[10/14 09:26:18,Telerik.TestStudio.ExecutionManagerService.exe(12852:12),Execution] JobRunner.RunJobAsync() : TestList loaded for job ID = 2353096d-e915-4d4a-9955-3040062a6968
[10/14 09:26:18,Telerik.TestStudio.ExecutionManagerService.exe(12852:12),Execution] JobRunner.RunJobAsync() : Job started, ID = 2353096d-e915-4d4a-9955-3040062a6968
[10/14 09:26:18,Telerik.TestStudio.RemoteExecutor.exe(11408:10)] First trace message from pool unnamed thread (managed ID = 10, native ID = 14532).
[10/14 09:26:18,Telerik.TestStudio.RemoteExecutor.exe(11408:10),Execution] RemoteExecutorPipeClient.StartTest() : Test run requested for test with path 'Activity\Validate activity.tstest'. Project path: 'C:\Efficio\TelerikTestStudio\Efficio Main Application'.
[10/14 09:26:18,Telerik.TestStudio.RemoteExecutor.exe(11408:5)] First trace message from pool unnamed thread (managed ID = 5, native ID = 9984).
[10/14 09:26:18,Telerik.TestStudio.RemoteExecutor.exe(11408:5),Warning] CloudRunnableGUITestBuilder.CreateLocalCacheAsync() : Started getting project from storage
[10/14 09:26:19,Telerik.TestStudio.RemoteExecutor.exe(11408:12)] First trace message from pool unnamed thread (managed ID = 12, native ID = 15720).
[10/14 09:26:19,Telerik.TestStudio.RemoteExecutor.exe(11408:12),Execution] CloudStorageProjectOpener.DownloadAndSaveTestsAsync() : ------- Function Start -------
[10/14 09:26:27,Telerik.TestStudio.RemoteExecutor.exe(11408:8)] First trace message from pool unnamed thread (managed ID = 8, native ID = 6244).
[10/14 09:26:27,Telerik.TestStudio.RemoteExecutor.exe(11408:8),Execution] CloudStorageProjectOpener.DownloadAndSaveTestsAsync() : ------- Function End -------
[10/14 09:26:27,Telerik.TestStudio.RemoteExecutor.exe(11408:8),Warning] CloudRunnableGUITestBuilder.CreateLocalCacheAsync() : Finished getting project from storage
[10/14 09:26:28,Telerik.TestStudio.RemoteExecutor.exe(11408:12),TestStudio] <>c__DisplayClass35_0.<LoadInternal>b__1() : Extract elements time: 00:00:00.3286247
[10/14 09:26:28,Telerik.TestStudio.RemoteExecutor.exe(11408:12),TestStudio] <>c__DisplayClass35_0.<LoadInternal>b__1() : Merge elements time: 00:00:00.0357646
[10/14 09:26:28,Telerik.TestStudio.RemoteExecutor.exe(11408:12),TestStudio] <>c__DisplayClass35_0.<LoadInternal>b__1() : Total Elements count: 294
[10/14 09:26:30,Telerik.TestStudio.RemoteExecutor.exe(11408:21)] First trace message from pool unnamed thread (managed ID = 21, native ID = 4652).
[10/14 09:26:30,Telerik.TestStudio.RemoteExecutor.exe(11408:21),Framework] TestAppLoader.LoadRefAssemblies() : External assembly loaded into the AppDomain: C:\Temp\Telerik Test Studio\TSProjects\92c411c3-850c-4006-89a6-a95aed591e6f\Efficio Main Application\..\Efficio Main Application\lib\Microsoft.ApplicationBlocks.Data.dll.
[10/14 09:26:31,ArtOfTest.Runner.exe(13916:3)] First trace message from process 13916: "D:\Program Files\Progress\Test Studio\Bin\ArtOfTest.Runner.exe"  /controller /parentprocesshandle=2832
[10/14 09:26:31,ArtOfTest.Runner.exe(13916:3)] First trace message from foreground thread "Remote.ArtOfTest.Runner.Listener" (managed ID = 3, native ID = 10120).
[10/14 09:26:31,ArtOfTest.Runner.exe(13916:3),TestStudio] Loader.LoadTranslatorGroups() : ------- Function Start -------
[10/14 09:26:32,ArtOfTest.Runner.exe(13916:3),TestStudio] Loader.LoadTranslatorGroups() : ------- Function End -------
[10/14 09:26:32,ArtOfTest.Runner.exe(13916:4)] First trace message from pool unnamed thread (managed ID = 4, native ID = 14008).
[10/14 09:26:32,ArtOfTest.Runner.exe(13916:4),Framework] TestAppLoader.LoadRefAssemblies() : External assembly loaded into the AppDomain: C:\Temp\Telerik Test Studio\TSProjects\92c411c3-850c-4006-89a6-a95aed591e6f\Efficio Main Application\..\Efficio Main Application\lib\Microsoft.ApplicationBlocks.Data.dll.
[10/14 09:26:32,ArtOfTest.Runner.exe(13916:4),TestStudio] Manager.GetDialogMatchProvider() : The dialog matches assembly loaded successfully.
[10/14 09:26:32,ArtOfTest.Runner.exe(13916:4),Framework] Manager.KillNonAutomatedBrowsers() : Closing non-automated 'iexplore' browser instances
[10/14 09:26:33,ArtOfTest.Runner.exe(13916:4),Framework] InternetExplorerActions.LaunchNewBrowserInstance() : Process launched (ID=1076, Path="C:\Program Files\Internet Explorer\iexplore.exe", Arguments="-nomerge about:blank").
[10/14 09:26:33,ArtOfTest.Runner.exe(13916:4),Framework] InternetExplorerActions.LaunchNewBrowserInstance() : Attempting to attach on IE frame (HWND=3016166)...
[10/14 09:26:34,ArtOfTest.Runner.exe(13916:4),Framework] Connector.Start() : Process launched (ID=12384, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""D:\Program Files\Progress\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 7472592").
[10/14 09:26:34,ArtOfTest.Runner.exe(13916:4),Framework] Connector.Start() : Process exited (ID=12384, ExitCode=0, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""D:\Program Files\Progress\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 7472592").
[10/14 09:26:34,ArtOfTest.Runner.exe(13916:4),Framework] Connector.InjectCode() : ------- Function Start -------
[10/14 09:26:34,ArtOfTest.Runner.exe(13916:4),Framework] Connector.InjectCode() : Connection string: C:\Windows\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2019.3.1002.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint?11408
[10/14 09:26:34,ArtOfTest.Runner.exe(13916:4),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 7472592 on another thread...
[10/14 09:26:34,ArtOfTest.Runner.exe(13916:9)] First trace message from pool thread "WaitAsync polling wait worker" (managed ID = 9, native ID = 14080).
[10/14 09:26:34,ArtOfTest.Runner.exe(13916:9),Framework] <>c__DisplayClass26_3.<WaitForDocument>b__1() : Attempting to get document from window handle
[10/14 09:26:35,ArtOfTest.Runner.exe(13916:4),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[10/14 09:26:35,ArtOfTest.Runner.exe(13916:4),Framework] Connector.InjectCode() : Sending WM_COPYDATA to HWND 7472592 (lpData = "C:\Windows\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2019.3.1002.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint?11408")...
[10/14 09:26:35,IEXPLORE.EXE(14392:1)] First trace message from process 14392: "C:\Program Files (x86)\Internet Explorer\IEXPLORE.EXE" SCODEF:1076 CREDAT:275457 /prefetch:2
[10/14 09:26:35,IEXPLORE.EXE(14392:1)] First trace message from background unnamed thread (managed ID = 1, native ID = 15292).
[10/14 09:26:35,IEXPLORE.EXE(14392:1),Framework] ArtOfTestPluginEntryPoint.Connect() : The .NET Framework is now active inside the hooked process (HWND = 7472592, PipeName = "Pipe.ArtOfTest.WebAii.BrowserProvisioner_-761257759", PID = 11408).
[10/14 09:26:35,IEXPLORE.EXE(14392:1),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 7472592 on same thread...
[10/14 09:26:35,IEXPLORE.EXE(14392:1),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[10/14 09:26:35,IEXPLORE.EXE(14392:1),Framework] BrowserRemoteClient constructor : The client ID for this hooked process will be "Client_07efa8df-2677-4a1c-8ec1-66ece8a6e931".
[10/14 09:26:35,ArtOfTest.Runner.exe(13916:6)] First trace message from background thread "Pipe.ArtOfTest.WebAii.BrowserProvisioner" (managed ID = 6, native ID = 12088).
[10/14 09:26:35,ArtOfTest.Runner.exe(13916:6),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe.
[10/14 09:26:35,ArtOfTest.Runner.exe(13916:13)] First trace message from background thread "Remoted async command listener" (managed ID = 13, native ID = 14668).
[10/14 09:26:35,ArtOfTest.Runner.exe(13916:13),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Not connected; this will now wait indefinitely for a pipe client connection...
[10/14 09:26:35,IEXPLORE.EXE(14392:1),Framework] BrowserRemoteClient.CreateNamedPipes() : Named pipes created for communication with the server: {Command = "8039a3a5-826d-4fb1-ac61-92bc9642ac28.CommandPipe", Async = "8039a3a5-826d-4fb1-ac61-92bc9642ac28.AsyncPipe"}
[10/14 09:26:35,ArtOfTest.Runner.exe(13916:13),Framework] BrowserRemoted.AsyncListenerThreadEntry() : A new client has connected to the pipe.
[10/14 09:26:35,ArtOfTest.Runner.exe(13916:13),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Now waiting indefinitely for a command from the pipe client...
[10/14 09:26:35,ArtOfTest.Runner.exe(13916:6),Framework] Manager.AddNewBrowser() : Added client ID "Client_07efa8df-2677-4a1c-8ec1-66ece8a6e931" (now there is/are 1 total).
[10/14 09:26:35,ArtOfTest.Runner.exe(13916:4),Framework] Connector.InjectCode() : Returned from sending WM_COPYDATA (LRESULT=0, GetLastError()=ERROR_ALREADY_EXISTS).
[10/14 09:26:35,ArtOfTest.Runner.exe(13916:4),Framework] Connector.InjectCode() : ------- Function End -------
[10/14 09:26:35,ArtOfTest.Runner.exe(13916:4),Execution] ExecutionEngine.InternalExecuteTest() : Executing test: 'Validate activity', path: 'Activity\Validate activity.tstest.'
[10/14 09:26:35,ArtOfTest.Runner.exe(13916:4),Execution] ExtensionsManager.LoadExtensions() : ------- Function Start -------
[10/14 09:26:35,ArtOfTest.Runner.exe(13916:4),Uncategorized] ExtensionsManager.LoadExtensions() : Got 14 plugin(s) in folder "D:\Program Files\Progress\Test Studio\Bin\Plugins".
[10/14 09:26:35,ArtOfTest.Runner.exe(13916:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "LibGit2Sharp.dll" from plugins folder.
[10/14 09:26:35,ArtOfTest.Runner.exe(13916:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Functional.dll" from plugins folder.
[10/14 09:26:35,ArtOfTest.Runner.exe(13916:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Jira.BugTracking.dll" from plugins folder.
[10/14 09:26:35,ArtOfTest.Runner.exe(13916:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Load.dll" from plugins folder.
[10/14 09:26:35,ArtOfTest.Runner.exe(13916:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Manual.dll" from plugins folder.
[10/14 09:26:35,ArtOfTest.Runner.exe(13916:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.OTA.Interop.dll" from plugins folder.
[10/14 09:26:35,ArtOfTest.Runner.exe(13916:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Plugins.dll" from plugins folder.
[10/14 09:26:35,ArtOfTest.Runner.exe(13916:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.ScreenRecorderExtension.dll" from plugins folder.
[10/14 09:26:36,ArtOfTest.Runner.exe(13916:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Shared.dll" from plugins folder.
[10/14 09:26:36,ArtOfTest.Runner.exe(13916:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.SourceControl.dll" from plugins folder.
[10/14 09:26:36,ArtOfTest.Runner.exe(13916:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.SourceControl.Git.dll" from plugins folder.
[10/14 09:26:36,ArtOfTest.Runner.exe(13916:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.SourceControl.Git.TS.dll" from plugins folder.
[10/14 09:26:36,ArtOfTest.Runner.exe(13916:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Web.dll" from plugins folder.
[10/14 09:26:36,ArtOfTest.Runner.exe(13916:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Wpf.dll" from plugins folder.
[10/14 09:26:36,ArtOfTest.Runner.exe(13916:4),Execution] ExtensionsManager.LoadExtensions() : ------- Function End -------
[10/14 09:26:36,ArtOfTest.Runner.exe(13916:4),TestStudio] VisualDebugger.SetCurrentResult() : VisualDebugger::SetCurrentResult
[10/14 09:26:36,ArtOfTest.Runner.exe(13916:4),DialogPlayback] DialogMonitor.Start() : Beginning monitoring for dialogs from the set {}.
[10/14 09:26:37,ArtOfTest.Runner.exe(13916:4),Uncategorized] Log.WriteLineInternal() : Test completed - performing setup
[10/14 09:26:37,ArtOfTest.Runner.exe(13916:4),Uncategorized] Log.WriteLineInternal() : Setting - Return URL: %2fEfficio%2fOpportunity%2fOpportunityDetail.aspx%3fmode%3d2, Wait URL: /Opportunity/OpportunityDetail.aspx
[10/14 09:26:37,ArtOfTest.Runner.exe(13916:4),Uncategorized] Log.WriteLineInternal() : Activity purpose - Automated Test Activity - f7e79a90-c9f0-4342-bef1-62e25c696830
[10/14 09:26:37,ArtOfTest.Runner.exe(13916:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:26:37,ArtOfTest.Runner.exe(13916:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 1 "Test"...
[10/14 09:26:37,ArtOfTest.Runner.exe(13916:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 1 "Test".
[10/14 09:26:37,ArtOfTest.Runner.exe(13916:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:26:37,ArtOfTest.Runner.exe(13916:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 2 "Execute test 'Login step'"...
[10/14 09:26:37,ArtOfTest.Runner.exe(13916:4),Execution] ExecutionEngine.InternalExecuteTest() : Executing test: 'Login step', path: 'Login\Steps\Login step.tstest.'
[10/14 09:26:37,ArtOfTest.Runner.exe(13916:4),TestStudio] VisualDebugger.SetCurrentResult() : VisualDebugger::SetCurrentResult
[10/14 09:26:37,ArtOfTest.Runner.exe(13916:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:26:37,ArtOfTest.Runner.exe(13916:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 1 "Login"...
[10/14 09:26:42,ArtOfTest.Runner.exe(13916:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 1 "Login".
[10/14 09:26:42,ArtOfTest.Runner.exe(13916:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 2 "Execute test 'Login step'".
[10/14 09:26:42,ArtOfTest.Runner.exe(13916:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:26:42,ArtOfTest.Runner.exe(13916:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 3 "RadButton('SaveButton'): is clicked"...
[10/14 09:26:42,ArtOfTest.Runner.exe(13916:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 3 "RadButton('SaveButton'): is clicked".
[10/14 09:26:42,ArtOfTest.Runner.exe(13916:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:26:43,ArtOfTest.Runner.exe(13916:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 4 "Wait for element 'ActivityErrorLabel' 'is' visible."...
[10/14 09:26:44,ArtOfTest.Runner.exe(13916:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 4 "Wait for element 'ActivityErrorLabel' 'is' visible.".
[10/14 09:26:44,ArtOfTest.Runner.exe(13916:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:26:44,ArtOfTest.Runner.exe(13916:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 5 "RadComboBox('ctl00_cphM_ecActivityGeneral_ddlActivityType'): selecting item with index '1'"...
[10/14 09:26:45,ArtOfTest.Runner.exe(13916:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 5 "RadComboBox('ctl00_cphM_ecActivityGeneral_ddlActivityType'): selecting item with index '1'".
[10/14 09:26:45,ArtOfTest.Runner.exe(13916:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:26:45,ArtOfTest.Runner.exe(13916:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 6 "RadButton('SaveButton'): is clicked"...
[10/14 09:26:45,ArtOfTest.Runner.exe(13916:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 6 "RadButton('SaveButton'): is clicked".
[10/14 09:26:45,ArtOfTest.Runner.exe(13916:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:26:45,ArtOfTest.Runner.exe(13916:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 7 "Wait for element 'ActivityErrorLabel' 'is' visible."...
[10/14 09:26:46,ArtOfTest.Runner.exe(13916:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 7 "Wait for element 'ActivityErrorLabel' 'is' visible.".
[10/14 09:26:46,ArtOfTest.Runner.exe(13916:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:26:46,ArtOfTest.Runner.exe(13916:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 8 "TimeView('StartTimeView'): select time '12:00 AM'."...
[10/14 09:26:47,ArtOfTest.Runner.exe(13916:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 8 "TimeView('StartTimeView'): select time '12:00 AM'.".
[10/14 09:26:47,ArtOfTest.Runner.exe(13916:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:26:47,ArtOfTest.Runner.exe(13916:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 9 "TimeView('EndTimeTimeView'): select time '6:00 PM'."...
[10/14 09:26:48,ArtOfTest.Runner.exe(13916:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 9 "TimeView('EndTimeTimeView'): select time '6:00 PM'.".
[10/14 09:26:48,ArtOfTest.Runner.exe(13916:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:26:48,ArtOfTest.Runner.exe(13916:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 10 "TimeView('ctl00_cphM_ecActivityGeneral_tpStartTime_timeView'): select time '7:00 PM'."...
[10/14 09:26:48,ArtOfTest.Runner.exe(13916:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 10 "TimeView('ctl00_cphM_ecActivityGeneral_tpStartTime_timeView'): select time '7:00 PM'.".
[10/14 09:26:48,ArtOfTest.Runner.exe(13916:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:26:49,ArtOfTest.Runner.exe(13916:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 11 "TimeView('EndTimeTimeView'): select time '6:00 PM'."...
[10/14 09:26:49,ArtOfTest.Runner.exe(13916:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 11 "TimeView('EndTimeTimeView'): select time '6:00 PM'.".
[10/14 09:26:49,ArtOfTest.Runner.exe(13916:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:26:49,ArtOfTest.Runner.exe(13916:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 12 "RadInput('ctl00_cphM_ecActivityGeneral_txtActivityPurpose_wrapper'): value '' entered. - DataDriven: [$(Purpose)]"...
[10/14 09:26:50,ArtOfTest.Runner.exe(13916:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 12 "RadInput('ctl00_cphM_ecActivityGeneral_txtActivityPurpose_wrapper'): value '' entered. - DataDriven: [$(Purpose)]".
[10/14 09:26:50,ArtOfTest.Runner.exe(13916:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:26:50,ArtOfTest.Runner.exe(13916:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 13 "RadButton('SaveButton'): is clicked"...
[10/14 09:26:50,ArtOfTest.Runner.exe(13916:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 13 "RadButton('SaveButton'): is clicked".
[10/14 09:26:50,ArtOfTest.Runner.exe(13916:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:26:50,ArtOfTest.Runner.exe(13916:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 14 "Wait '10000' msec for url:'/Efficio/Opportunity/Activities.aspx'"...
[10/14 09:26:52,ArtOfTest.Runner.exe(13916:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 14 "Wait '10000' msec for url:'/Efficio/Opportunity/Activities.aspx'".
[10/14 09:26:52,ArtOfTest.Runner.exe(13916:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:26:52,ArtOfTest.Runner.exe(13916:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 15 "Check Activity page"...
[10/14 09:26:52,ArtOfTest.Runner.exe(13916:4),Uncategorized] Log.WriteLineInternal() : Searching for item in grid - Grid ID: ctl00_cphM_dgridOpportunities, Text: Automated Test Activity - f7e79a90-c9f0-4342-bef1-62e25c696830
[10/14 09:26:53,ArtOfTest.Runner.exe(13916:4),Uncategorized] Log.WriteLineInternal() : Item found in row: Row Index: 2, Cell Text: Automated Test Activit... 
[10/14 09:26:53,ArtOfTest.Runner.exe(13916:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 15 "Check Activity page".
[10/14 09:26:53,ArtOfTest.Runner.exe(13916:4),Uncategorized] Log.WriteLineInternal() : Test completed - performing cleanup
[10/14 09:26:53,ArtOfTest.Runner.exe(13916:4),Uncategorized] Log.WriteLineInternal() : Connecting to database.
[10/14 09:26:53,Telerik.TestStudio.ScreenRecorder.exe(15400:1)] First trace message from process 15400: "D:\Program Files\Progress\Test Studio\Bin\\Telerik.TestStudio.ScreenRecorder.exe" startnamedpipe="Pipes.ArtOfTest.WebAii.ScreenRecording.Control.132155331970084330" o="D:\Logs\TelerikTestStudio\Recording\Validate activity_63706641997008.avi" c=mjpeg s=100 f=5 size=0 d=\\.\DISPLAY1
[10/14 09:26:53,Telerik.TestStudio.ScreenRecorder.exe(15400:1)] First trace message from foreground unnamed thread (managed ID = 1, native ID = 16256).
[10/14 09:26:53,Telerik.TestStudio.ScreenRecorder.exe(15400:1),Error] Program.Main() : EXCEPTION! (see below)
     Outer Exception Type: System.AggregateException
     Message: One or more errors occurred.
     HRESULT: 0x80131500 (Official ID (if app.) = COR_E_EXCEPTION, Error Bit = FAILED, Facility = FACILITY_URT, Code = 5376)
     Call Stack:
          at System.Threading.Tasks.Task.ThrowIfExceptional(Boolean includeTaskCanceledExceptions)
          at System.Threading.Tasks.Task.Wait(Int32 millisecondsTimeout, CancellationToken cancellationToken)
          at Telerik.TestStudio.ScreenRecorder.Program.Main(String[] args)

     Inner Exception Type: System.Threading.Tasks.TaskCanceledException
     Message: A task was canceled.
     HRESULT: 0x8013153B (Official ID (if app.) = COR_E_OPERATIONCANCELED, Error Bit = FAILED, Facility = FACILITY_URT, Code = 5435)

[10/14 09:26:53,ArtOfTest.Runner.exe(13916:4),Uncategorized] Log.WriteLineInternal() : Deleting Activity - ID: 4637
[10/14 09:26:53,ArtOfTest.Runner.exe(13916:4),Uncategorized] Log.WriteLineInternal() : Connecting to database.
[10/14 09:26:53,ArtOfTest.Runner.exe(13916:4),TestStudio] VisualDebugger.ShutDown() : VisualDebbuger::ShutDown::Start
[10/14 09:26:53,ArtOfTest.Runner.exe(13916:4),TestStudio] VisualDebugger.ShutDown() : VisualDebbuger::ShutDown::End
[10/14 09:26:53,ArtOfTest.Runner.exe(13916:4),Framework] BrowserProvisioner.EndService() : Connecting to the provisioning pipe "Pipe.ArtOfTest.WebAii.BrowserProvisioner_-761257759" in order to unblock and eventually terminate it...
[10/14 09:26:53,ArtOfTest.Runner.exe(13916:4),Framework] BrowserProvisioner.EndService() : Provisioning pipe connected.
[10/14 09:26:53,ArtOfTest.Runner.exe(13916:6),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe but this will be treated as a termination cue.
[10/14 09:26:53,ArtOfTest.Runner.exe(13916:4),Framework] BrowserProvisioner.EndService() : Provisioning thread terminated.
[10/14 09:26:53,ArtOfTest.Runner.exe(13916:4),DialogPlayback] DialogMonitor.AddDialog() : Added {OnBeforeUnloadDialog(Dismiss=OK)} for monitoring.
[10/14 09:26:53,ArtOfTest.Runner.exe(13916:4),Framework] Manager.RemoveBrowser() : Removed client ID "Client_07efa8df-2677-4a1c-8ec1-66ece8a6e931" (0 remaining).
[10/14 09:26:53,ArtOfTest.Runner.exe(13916:4),Framework] Manager.SetActiveBrowser() : Active browser is now null.
[10/14 09:26:53,ArtOfTest.Runner.exe(13916:4),Framework] BrowserRemoted.End() : Now aborting the listener thread (TID = 13).
[10/14 09:26:53,IEXPLORE.EXE(14392:1),Framework] BrowserRemoteClient.Disconnect() : Sending ClientDisconnectRequest(ClientId="Client_07efa8df-2677-4a1c-8ec1-66ece8a6e931") to pipe server...
[10/14 09:26:53,IEXPLORE.EXE(14392:1),Framework] BrowserRemoteClient.Disconnect() : Command pipe is already disconnected.
[10/14 09:26:53,ArtOfTest.Runner.exe(13916:13),Warning] PipeCommunication.SyncPipeRead() : Pipe read broken by thread-abort.
[10/14 09:26:56,ArtOfTest.Runner.exe(13916:4),DialogPlayback] DialogMonitor.Stop() : Dialog monitoring is being halted.
[10/14 09:26:56,ArtOfTest.Runner.exe(13916:4),UIAutomation] AutomationExtensions.AddAutomationEventHandler() : Added a(n) WindowPatternIdentifiers.WindowOpenedProperty handler on element System.Windows.Automation.AutomationElement scope Descendants.
[10/14 09:26:57,Telerik.TestStudio.RemoteExecutor.exe(11408:23)] First trace message from background thread "Callback Listener Thread" (managed ID = 23, native ID = 11768).
[10/14 09:26:57,Telerik.TestStudio.RemoteExecutor.exe(11408:23),Execution] TestPlatform.FinishTestRunAndPublishResultsAsync() : Finished running test platform - attempting publish
[10/14 09:26:59,Telerik.TestStudio.RemoteExecutor.exe(11408:24)] First trace message from pool unnamed thread (managed ID = 24, native ID = 8636).
[10/14 09:26:59,Telerik.TestStudio.RemoteExecutor.exe(11408:24),Execution] RemoteExecutorPipeClient.StartTest() : Test run requested for test with path 'Activity\Delete activity.tstest'. Project path: 'C:\Efficio\TelerikTestStudio\Efficio Main Application'.
[10/14 09:26:59,Telerik.TestStudio.RemoteExecutor.exe(11408:5),Framework] TestAppLoader.LoadRefAssemblies() : External assembly loaded into the AppDomain: C:\Temp\Telerik Test Studio\TSProjects\92c411c3-850c-4006-89a6-a95aed591e6f\Efficio Main Application\..\Efficio Main Application\lib\Microsoft.ApplicationBlocks.Data.dll.
[10/14 09:26:59,ArtOfTest.Runner.exe(13916:5)] First trace message from pool unnamed thread (managed ID = 5, native ID = 12928).
[10/14 09:26:59,ArtOfTest.Runner.exe(13916:5),Framework] TestAppLoader.LoadRefAssemblies() : External assembly loaded into the AppDomain: C:\Temp\Telerik Test Studio\TSProjects\92c411c3-850c-4006-89a6-a95aed591e6f\Efficio Main Application\..\Efficio Main Application\lib\Microsoft.ApplicationBlocks.Data.dll.
[10/14 09:26:59,ArtOfTest.Runner.exe(13916:5),TestStudio] Manager.GetDialogMatchProvider() : The dialog matches assembly loaded successfully.
[10/14 09:26:59,ArtOfTest.Runner.exe(13916:5),Framework] Manager.KillNonAutomatedBrowsers() : Closing non-automated 'iexplore' browser instances
[10/14 09:26:59,ArtOfTest.Runner.exe(13916:5),Framework] InternetExplorerActions.LaunchNewBrowserInstance() : Process launched (ID=13012, Path="C:\Program Files\Internet Explorer\iexplore.exe", Arguments="-nomerge about:blank").
[10/14 09:27:00,ArtOfTest.Runner.exe(13916:5),Framework] InternetExplorerActions.LaunchNewBrowserInstance() : Attempting to attach on IE frame (HWND=5965162)...
[10/14 09:27:00,ArtOfTest.Runner.exe(13916:5),Framework] Connector.Start() : Process launched (ID=16256, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""D:\Program Files\Progress\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 28575066").
[10/14 09:27:00,ArtOfTest.Runner.exe(13916:5),Framework] Connector.Start() : Process exited (ID=16256, ExitCode=0, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""D:\Program Files\Progress\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 28575066").
[10/14 09:27:00,ArtOfTest.Runner.exe(13916:5),Framework] Connector.InjectCode() : ------- Function Start -------
[10/14 09:27:00,ArtOfTest.Runner.exe(13916:5),Framework] Connector.InjectCode() : Connection string: C:\Windows\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2019.3.1002.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint?11408
[10/14 09:27:00,ArtOfTest.Runner.exe(13916:5),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 28575066 on another thread...
[10/14 09:27:00,ArtOfTest.Runner.exe(13916:9)] First trace message from pool thread "WaitAsync polling wait worker" (managed ID = 9, native ID = 14080).
[10/14 09:27:00,ArtOfTest.Runner.exe(13916:9),Framework] <>c__DisplayClass26_3.<WaitForDocument>b__1() : Attempting to get document from window handle
[10/14 09:27:00,ArtOfTest.Runner.exe(13916:5),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[10/14 09:27:00,ArtOfTest.Runner.exe(13916:5),Framework] Connector.InjectCode() : Sending WM_COPYDATA to HWND 28575066 (lpData = "C:\Windows\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2019.3.1002.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint?11408")...
[10/14 09:27:00,IEXPLORE.EXE(16344:1)] First trace message from process 16344: "C:\Program Files (x86)\Internet Explorer\IEXPLORE.EXE" SCODEF:13012 CREDAT:275457 /prefetch:2
[10/14 09:27:00,IEXPLORE.EXE(16344:1)] First trace message from background unnamed thread (managed ID = 1, native ID = 13716).
[10/14 09:27:00,IEXPLORE.EXE(16344:1),Framework] ArtOfTestPluginEntryPoint.Connect() : The .NET Framework is now active inside the hooked process (HWND = 28575066, PipeName = "Pipe.ArtOfTest.WebAii.BrowserProvisioner_1449784794", PID = 11408).
[10/14 09:27:00,IEXPLORE.EXE(16344:1),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 28575066 on same thread...
[10/14 09:27:00,IEXPLORE.EXE(16344:1),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[10/14 09:27:00,IEXPLORE.EXE(16344:1),Framework] BrowserRemoteClient constructor : The client ID for this hooked process will be "Client_954470b9-002d-43a3-875e-6df839dcbb34".
[10/14 09:27:00,ArtOfTest.Runner.exe(13916:16)] First trace message from background thread "Pipe.ArtOfTest.WebAii.BrowserProvisioner" (managed ID = 16, native ID = 13384).
[10/14 09:27:00,ArtOfTest.Runner.exe(13916:16),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe.
[10/14 09:27:00,ArtOfTest.Runner.exe(13916:6)] First trace message from background thread "Remoted async command listener" (managed ID = 6, native ID = 15444).
[10/14 09:27:00,ArtOfTest.Runner.exe(13916:6),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Not connected; this will now wait indefinitely for a pipe client connection...
[10/14 09:27:00,IEXPLORE.EXE(16344:1),Framework] BrowserRemoteClient.CreateNamedPipes() : Named pipes created for communication with the server: {Command = "b94fd726-bde8-49ad-8a36-b5028ba136de.CommandPipe", Async = "b94fd726-bde8-49ad-8a36-b5028ba136de.AsyncPipe"}
[10/14 09:27:00,ArtOfTest.Runner.exe(13916:6),Framework] BrowserRemoted.AsyncListenerThreadEntry() : A new client has connected to the pipe.
[10/14 09:27:00,ArtOfTest.Runner.exe(13916:6),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Now waiting indefinitely for a command from the pipe client...
[10/14 09:27:00,ArtOfTest.Runner.exe(13916:16),Framework] Manager.AddNewBrowser() : Added client ID "Client_954470b9-002d-43a3-875e-6df839dcbb34" (now there is/are 1 total).
[10/14 09:27:00,ArtOfTest.Runner.exe(13916:5),Framework] Connector.InjectCode() : Returned from sending WM_COPYDATA (LRESULT=0, GetLastError()=ERROR_ALREADY_EXISTS).
[10/14 09:27:00,ArtOfTest.Runner.exe(13916:5),Framework] Connector.InjectCode() : ------- Function End -------
[10/14 09:27:00,ArtOfTest.Runner.exe(13916:5),Execution] ExecutionEngine.InternalExecuteTest() : Executing test: 'Delete activity', path: 'Activity\Delete activity.tstest.'
[10/14 09:27:01,ArtOfTest.Runner.exe(13916:5),TestStudio] VisualDebugger.SetCurrentResult() : VisualDebugger::SetCurrentResult
[10/14 09:27:01,ArtOfTest.Runner.exe(13916:5),DialogPlayback] DialogMonitor.Start() : Beginning monitoring for dialogs from the set {}.
[10/14 09:27:01,ArtOfTest.Runner.exe(13916:5),Uncategorized] Log.WriteLineInternal() : Test completed - performing setup
[10/14 09:27:01,ArtOfTest.Runner.exe(13916:5),Uncategorized] Log.WriteLineInternal() : Setting - Return URL: %2fEfficio%2fOpportunity%2fOpportunityDetail.aspx%3fmode%3d2, Wait URL: /Opportunity/OpportunityDetail.aspx
[10/14 09:27:01,ArtOfTest.Runner.exe(13916:5),Uncategorized] Log.WriteLineInternal() : Activity purpose - Automated Test Activity - cfb6231d-5db8-494f-8ca8-599d13e6b50c
[10/14 09:27:01,ArtOfTest.Runner.exe(13916:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:27:01,ArtOfTest.Runner.exe(13916:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 2 "Execute test 'Login step'"...
[10/14 09:27:01,ArtOfTest.Runner.exe(13916:5),Execution] ExecutionEngine.InternalExecuteTest() : Executing test: 'Login step', path: 'Login\Steps\Login step.tstest.'
[10/14 09:27:01,ArtOfTest.Runner.exe(13916:5),TestStudio] VisualDebugger.SetCurrentResult() : VisualDebugger::SetCurrentResult
[10/14 09:27:01,ArtOfTest.Runner.exe(13916:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:27:01,ArtOfTest.Runner.exe(13916:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 1 "Login"...
[10/14 09:27:04,ArtOfTest.Runner.exe(13916:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 1 "Login".
[10/14 09:27:04,ArtOfTest.Runner.exe(13916:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 2 "Execute test 'Login step'".
[10/14 09:27:04,ArtOfTest.Runner.exe(13916:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:27:04,ArtOfTest.Runner.exe(13916:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 3 "RadComboBox('ctl00_cphM_ecActivityGeneral_ddlActivityType'): selecting item with index '2'"...
[10/14 09:27:05,ArtOfTest.Runner.exe(13916:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 3 "RadComboBox('ctl00_cphM_ecActivityGeneral_ddlActivityType'): selecting item with index '2'".
[10/14 09:27:05,ArtOfTest.Runner.exe(13916:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:27:05,ArtOfTest.Runner.exe(13916:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 4 "RadInput('ctl00_cphM_ecActivityGeneral_txtActivityPurpose_wrapper'): value '' entered. - DataDriven: [$(Purpose)]"...
[10/14 09:27:05,ArtOfTest.Runner.exe(13916:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 4 "RadInput('ctl00_cphM_ecActivityGeneral_txtActivityPurpose_wrapper'): value '' entered. - DataDriven: [$(Purpose)]".
[10/14 09:27:05,ArtOfTest.Runner.exe(13916:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:27:05,ArtOfTest.Runner.exe(13916:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 5 "RadButton('SaveButton'): is clicked"...
[10/14 09:27:06,ArtOfTest.Runner.exe(13916:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 5 "RadButton('SaveButton'): is clicked".
[10/14 09:27:06,ArtOfTest.Runner.exe(13916:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:27:06,ArtOfTest.Runner.exe(13916:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 6 "Wait '10000' msec for url:'/Efficio/Opportunity/Activities.aspx'"...
[10/14 09:27:08,ArtOfTest.Runner.exe(13916:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 6 "Wait '10000' msec for url:'/Efficio/Opportunity/Activities.aspx'".
[10/14 09:27:08,ArtOfTest.Runner.exe(13916:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:27:08,ArtOfTest.Runner.exe(13916:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 7 "Check Activity page"...
[10/14 09:27:08,ArtOfTest.Runner.exe(13916:5),Uncategorized] Log.WriteLineInternal() : Searching for item in grid - Grid ID: ctl00_cphM_dgridOpportunities, Text: Automated Test Activity - cfb6231d-5db8-494f-8ca8-599d13e6b50c
[10/14 09:27:08,ArtOfTest.Runner.exe(13916:5),Uncategorized] Log.WriteLineInternal() : Item found in row: Row Index: 2, Cell Text: Automated Test Activit... 
[10/14 09:27:08,ArtOfTest.Runner.exe(13916:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 7 "Check Activity page".
[10/14 09:27:08,ArtOfTest.Runner.exe(13916:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:27:08,ArtOfTest.Runner.exe(13916:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 8 "Retrieve the Activity ID"...
[10/14 09:27:08,ArtOfTest.Runner.exe(13916:5),Uncategorized] Log.WriteLineInternal() : Connecting to database.
[10/14 09:27:08,ArtOfTest.Runner.exe(13916:5),Uncategorized] Log.WriteLineInternal() : Retrieved Activity - ID: 4638
[10/14 09:27:08,ArtOfTest.Runner.exe(13916:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 8 "Retrieve the Activity ID".
[10/14 09:27:08,ArtOfTest.Runner.exe(13916:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:27:08,ArtOfTest.Runner.exe(13916:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 9 "Navigate to : '' - DataDriven: [$(EditActivityUrl)]"...
[10/14 09:27:10,ArtOfTest.Runner.exe(13916:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 9 "Navigate to : '' - DataDriven: [$(EditActivityUrl)]".
[10/14 09:27:10,ArtOfTest.Runner.exe(13916:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:27:10,ArtOfTest.Runner.exe(13916:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 10 "Wait '5000' msec for url:'/Efficio/Opportunity/OpportunityDetail.aspx'"...
[10/14 09:27:10,ArtOfTest.Runner.exe(13916:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 10 "Wait '5000' msec for url:'/Efficio/Opportunity/OpportunityDetail.aspx'".
[10/14 09:27:10,ArtOfTest.Runner.exe(13916:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:27:10,ArtOfTest.Runner.exe(13916:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 11 "RadButton('ActivityDeleteButton'): is clicked"...
[10/14 09:27:11,ArtOfTest.Runner.exe(13916:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 11 "RadButton('ActivityDeleteButton'): is clicked".
[10/14 09:27:11,ArtOfTest.Runner.exe(13916:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:27:11,ArtOfTest.Runner.exe(13916:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 12 "Verify the activity was deleted"...
[10/14 09:27:11,ArtOfTest.Runner.exe(13916:5),Uncategorized] Log.WriteLineInternal() : Connecting to database.
[10/14 09:27:11,ArtOfTest.Runner.exe(13916:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 12 "Verify the activity was deleted".
[10/14 09:27:11,ArtOfTest.Runner.exe(13916:5),Uncategorized] Log.WriteLineInternal() : Test completed - performing cleanup
[10/14 09:27:11,ArtOfTest.Runner.exe(13916:5),Uncategorized] Log.WriteLineInternal() : Deleting Activity - ID: 4638
[10/14 09:27:11,ArtOfTest.Runner.exe(13916:5),Uncategorized] Log.WriteLineInternal() : Connecting to database.
[10/14 09:27:11,Telerik.TestStudio.ScreenRecorder.exe(13464:1)] First trace message from process 13464: "D:\Program Files\Progress\Test Studio\Bin\\Telerik.TestStudio.ScreenRecorder.exe" startnamedpipe="Pipes.ArtOfTest.WebAii.ScreenRecording.Control.132155332213089730" o="D:\Logs\TelerikTestStudio\Recording\Delete activity_63706642021308.avi" c=mjpeg s=100 f=5 size=0 d=\\.\DISPLAY1
[10/14 09:27:11,Telerik.TestStudio.ScreenRecorder.exe(13464:1)] First trace message from foreground unnamed thread (managed ID = 1, native ID = 15032).
[10/14 09:27:11,Telerik.TestStudio.ScreenRecorder.exe(13464:1),Error] Program.Main() : EXCEPTION! (see below)
     Outer Exception Type: System.AggregateException
     Message: One or more errors occurred.
     HRESULT: 0x80131500 (Official ID (if app.) = COR_E_EXCEPTION, Error Bit = FAILED, Facility = FACILITY_URT, Code = 5376)
     Call Stack:
          at System.Threading.Tasks.Task.ThrowIfExceptional(Boolean includeTaskCanceledExceptions)
          at System.Threading.Tasks.Task.Wait(Int32 millisecondsTimeout, CancellationToken cancellationToken)
          at Telerik.TestStudio.ScreenRecorder.Program.Main(String[] args)

     Inner Exception Type: System.Threading.Tasks.TaskCanceledException
     Message: A task was canceled.
     HRESULT: 0x8013153B (Official ID (if app.) = COR_E_OPERATIONCANCELED, Error Bit = FAILED, Facility = FACILITY_URT, Code = 5435)

[10/14 09:27:11,ArtOfTest.Runner.exe(13916:5),TestStudio] VisualDebugger.ShutDown() : VisualDebbuger::ShutDown::Start
[10/14 09:27:11,ArtOfTest.Runner.exe(13916:5),TestStudio] VisualDebugger.ShutDown() : VisualDebbuger::ShutDown::End
[10/14 09:27:11,ArtOfTest.Runner.exe(13916:5),Framework] BrowserProvisioner.EndService() : Connecting to the provisioning pipe "Pipe.ArtOfTest.WebAii.BrowserProvisioner_1449784794" in order to unblock and eventually terminate it...
[10/14 09:27:11,ArtOfTest.Runner.exe(13916:5),Framework] BrowserProvisioner.EndService() : Provisioning pipe connected.
[10/14 09:27:11,ArtOfTest.Runner.exe(13916:16),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe but this will be treated as a termination cue.
[10/14 09:27:11,ArtOfTest.Runner.exe(13916:5),Framework] BrowserProvisioner.EndService() : Provisioning thread terminated.
[10/14 09:27:11,ArtOfTest.Runner.exe(13916:5),DialogPlayback] DialogMonitor.AddDialog() : Added {OnBeforeUnloadDialog(Dismiss=OK)} for monitoring.
[10/14 09:27:11,ArtOfTest.Runner.exe(13916:5),Framework] Manager.RemoveBrowser() : Removed client ID "Client_954470b9-002d-43a3-875e-6df839dcbb34" (0 remaining).
[10/14 09:27:11,ArtOfTest.Runner.exe(13916:5),Framework] Manager.SetActiveBrowser() : Active browser is now null.
[10/14 09:27:11,ArtOfTest.Runner.exe(13916:5),Framework] BrowserRemoted.End() : Now aborting the listener thread (TID = 6).
[10/14 09:27:11,IEXPLORE.EXE(16344:1),Framework] BrowserRemoteClient.Disconnect() : Sending ClientDisconnectRequest(ClientId="Client_954470b9-002d-43a3-875e-6df839dcbb34") to pipe server...
[10/14 09:27:11,IEXPLORE.EXE(16344:1),Framework] BrowserRemoteClient.Disconnect() : Command pipe is already disconnected.
[10/14 09:27:11,ArtOfTest.Runner.exe(13916:6),Warning] PipeCommunication.SyncPipeRead() : Pipe read broken by thread-abort.
[10/14 09:27:14,ArtOfTest.Runner.exe(13916:5),DialogPlayback] DialogMonitor.Stop() : Dialog monitoring is being halted.
[10/14 09:27:14,ArtOfTest.Runner.exe(13916:5),UIAutomation] AutomationExtensions.AddAutomationEventHandler() : Added a(n) WindowPatternIdentifiers.WindowOpenedProperty handler on element System.Windows.Automation.AutomationElement scope Descendants.
[10/14 09:27:14,Telerik.TestStudio.RemoteExecutor.exe(11408:23),Execution] TestPlatform.FinishTestRunAndPublishResultsAsync() : Finished running test platform - attempting publish
[10/14 09:27:19,Telerik.TestStudio.RemoteExecutor.exe(11408:13)] First trace message from pool unnamed thread (managed ID = 13, native ID = 12972).
[10/14 09:27:19,Telerik.TestStudio.RemoteExecutor.exe(11408:13),Execution] RemoteExecutorPipeClient.StartTest() : Test run requested for test with path 'Activity\Add activity from Activity Detail page.tstest'. Project path: 'C:\Efficio\TelerikTestStudio\Efficio Main Application'.
[10/14 09:27:19,Telerik.TestStudio.RemoteExecutor.exe(11408:5),Framework] TestAppLoader.LoadRefAssemblies() : External assembly loaded into the AppDomain: C:\Temp\Telerik Test Studio\TSProjects\92c411c3-850c-4006-89a6-a95aed591e6f\Efficio Main Application\..\Efficio Main Application\lib\Microsoft.ApplicationBlocks.Data.dll.
[10/14 09:27:19,ArtOfTest.Runner.exe(13916:9)] First trace message from pool unnamed thread (managed ID = 9, native ID = 14080).
[10/14 09:27:19,ArtOfTest.Runner.exe(13916:9),Framework] TestAppLoader.LoadRefAssemblies() : External assembly loaded into the AppDomain: C:\Temp\Telerik Test Studio\TSProjects\92c411c3-850c-4006-89a6-a95aed591e6f\Efficio Main Application\..\Efficio Main Application\lib\Microsoft.ApplicationBlocks.Data.dll.
[10/14 09:27:19,ArtOfTest.Runner.exe(13916:9),TestStudio] Manager.GetDialogMatchProvider() : The dialog matches assembly loaded successfully.
[10/14 09:27:19,ArtOfTest.Runner.exe(13916:9),Framework] Manager.KillNonAutomatedBrowsers() : Closing non-automated 'iexplore' browser instances
[10/14 09:27:20,ArtOfTest.Runner.exe(13916:9),Framework] InternetExplorerActions.LaunchNewBrowserInstance() : Process launched (ID=16196, Path="C:\Program Files\Internet Explorer\iexplore.exe", Arguments="-nomerge about:blank").
[10/14 09:27:20,ArtOfTest.Runner.exe(13916:9),Framework] InternetExplorerActions.LaunchNewBrowserInstance() : Attempting to attach on IE frame (HWND=1115686)...
[10/14 09:27:20,ArtOfTest.Runner.exe(13916:9),Framework] Connector.Start() : Process launched (ID=9248, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""D:\Program Files\Progress\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 1377964").
[10/14 09:27:20,ArtOfTest.Runner.exe(13916:9),Framework] Connector.Start() : Process exited (ID=9248, ExitCode=0, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""D:\Program Files\Progress\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 1377964").
[10/14 09:27:20,ArtOfTest.Runner.exe(13916:9),Framework] Connector.InjectCode() : ------- Function Start -------
[10/14 09:27:20,ArtOfTest.Runner.exe(13916:9),Framework] Connector.InjectCode() : Connection string: C:\Windows\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2019.3.1002.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint?11408
[10/14 09:27:20,ArtOfTest.Runner.exe(13916:9),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 1377964 on another thread...
[10/14 09:27:20,ArtOfTest.Runner.exe(13916:4)] First trace message from pool thread "WaitAsync polling wait worker" (managed ID = 4, native ID = 14008).
[10/14 09:27:20,ArtOfTest.Runner.exe(13916:4),Framework] <>c__DisplayClass26_3.<WaitForDocument>b__1() : Attempting to get document from window handle
[10/14 09:27:21,ArtOfTest.Runner.exe(13916:9),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[10/14 09:27:21,ArtOfTest.Runner.exe(13916:9),Framework] Connector.InjectCode() : Sending WM_COPYDATA to HWND 1377964 (lpData = "C:\Windows\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2019.3.1002.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint?11408")...
[10/14 09:27:21,IEXPLORE.EXE(11372:1)] First trace message from process 11372: "C:\Program Files (x86)\Internet Explorer\IEXPLORE.EXE" SCODEF:16196 CREDAT:275457 /prefetch:2
[10/14 09:27:21,IEXPLORE.EXE(11372:1)] First trace message from background unnamed thread (managed ID = 1, native ID = 13188).
[10/14 09:27:21,IEXPLORE.EXE(11372:1),Framework] ArtOfTestPluginEntryPoint.Connect() : The .NET Framework is now active inside the hooked process (HWND = 1377964, PipeName = "Pipe.ArtOfTest.WebAii.BrowserProvisioner_2042020003", PID = 11408).
[10/14 09:27:21,IEXPLORE.EXE(11372:1),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 1377964 on same thread...
[10/14 09:27:21,IEXPLORE.EXE(11372:1),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[10/14 09:27:21,IEXPLORE.EXE(11372:1),Framework] BrowserRemoteClient constructor : The client ID for this hooked process will be "Client_c23d6166-2610-49c5-8e5b-1829f6fe8757".
[10/14 09:27:21,ArtOfTest.Runner.exe(13916:6)] First trace message from background thread "Remoted async command listener" (managed ID = 6, native ID = 15268).
[10/14 09:27:21,ArtOfTest.Runner.exe(13916:6),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe.
[10/14 09:27:21,ArtOfTest.Runner.exe(13916:7)] First trace message from background thread "Remoted async command listener" (managed ID = 7, native ID = 12456).
[10/14 09:27:21,ArtOfTest.Runner.exe(13916:7),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Not connected; this will now wait indefinitely for a pipe client connection...
[10/14 09:27:21,IEXPLORE.EXE(11372:1),Framework] BrowserRemoteClient.CreateNamedPipes() : Named pipes created for communication with the server: {Command = "800ac104-3167-4d90-ba30-4e41ceb8a0f4.CommandPipe", Async = "800ac104-3167-4d90-ba30-4e41ceb8a0f4.AsyncPipe"}
[10/14 09:27:21,ArtOfTest.Runner.exe(13916:7),Framework] BrowserRemoted.AsyncListenerThreadEntry() : A new client has connected to the pipe.
[10/14 09:27:21,ArtOfTest.Runner.exe(13916:7),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Now waiting indefinitely for a command from the pipe client...
[10/14 09:27:21,ArtOfTest.Runner.exe(13916:6),Framework] Manager.AddNewBrowser() : Added client ID "Client_c23d6166-2610-49c5-8e5b-1829f6fe8757" (now there is/are 1 total).
[10/14 09:27:21,ArtOfTest.Runner.exe(13916:9),Framework] Connector.InjectCode() : Returned from sending WM_COPYDATA (LRESULT=0, GetLastError()=ERROR_ALREADY_EXISTS).
[10/14 09:27:21,ArtOfTest.Runner.exe(13916:9),Framework] Connector.InjectCode() : ------- Function End -------
[10/14 09:27:21,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.InternalExecuteTest() : Executing test: 'Add activity from Activity Detail page', path: 'Activity\Add activity from Activity Detail page.tstest.'
[10/14 09:27:21,ArtOfTest.Runner.exe(13916:9),TestStudio] VisualDebugger.SetCurrentResult() : VisualDebugger::SetCurrentResult
[10/14 09:27:21,ArtOfTest.Runner.exe(13916:9),DialogPlayback] DialogMonitor.Start() : Beginning monitoring for dialogs from the set {}.
[10/14 09:27:22,ArtOfTest.Runner.exe(13916:9),Uncategorized] Log.WriteLineInternal() : Test completed - performing setup
[10/14 09:27:22,ArtOfTest.Runner.exe(13916:9),Uncategorized] Log.WriteLineInternal() : Setting - Return URL: %2fEfficio%2fOpportunity%2fOpportunityDetail.aspx%3fmode%3d2, Wait URL: /Opportunity/OpportunityDetail.aspx
[10/14 09:27:22,ArtOfTest.Runner.exe(13916:9),Uncategorized] Log.WriteLineInternal() : date - 10/15/2019
[10/14 09:27:22,ArtOfTest.Runner.exe(13916:9),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:27:22,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 2 "Execute test 'Login step'"...
[10/14 09:27:22,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.InternalExecuteTest() : Executing test: 'Login step', path: 'Login\Steps\Login step.tstest.'
[10/14 09:27:22,ArtOfTest.Runner.exe(13916:9),TestStudio] VisualDebugger.SetCurrentResult() : VisualDebugger::SetCurrentResult
[10/14 09:27:22,ArtOfTest.Runner.exe(13916:9),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:27:22,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 1 "Login"...
[10/14 09:27:24,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 1 "Login".
[10/14 09:27:24,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 2 "Execute test 'Login step'".
[10/14 09:27:24,ArtOfTest.Runner.exe(13916:9),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:27:24,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 3 "RadComboBox('ctl00_cphM_ecActivityGeneral_ddlActivityAdvertiser'): drop down action -> 'Open'"...
[10/14 09:27:25,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 3 "RadComboBox('ctl00_cphM_ecActivityGeneral_ddlActivityAdvertiser'): drop down action -> 'Open'".
[10/14 09:27:25,ArtOfTest.Runner.exe(13916:9),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:27:25,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 4 "RadComboBox('ActivityAdvertiserComboBox'): text 'air' entered in combobox input"...
[10/14 09:27:26,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 4 "RadComboBox('ActivityAdvertiserComboBox'): text 'air' entered in combobox input".
[10/14 09:27:26,ArtOfTest.Runner.exe(13916:9),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:27:27,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 5 "Wait for 'TextContent' 'Contains' 'Air Around The Clock' on 'AdvertiserComboBoxSelectionOne'"...
[10/14 09:27:28,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 5 "Wait for 'TextContent' 'Contains' 'Air Around The Clock' on 'AdvertiserComboBoxSelectionOne'".
[10/14 09:27:28,ArtOfTest.Runner.exe(13916:9),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:27:28,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 6 "Click 'ActivityAdvertiserComboBoxSelectionOne'"...
[10/14 09:27:28,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 6 "Click 'ActivityAdvertiserComboBoxSelectionOne'".
[10/14 09:27:28,ArtOfTest.Runner.exe(13916:9),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:27:28,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 7 "Wait for 'TextContent' 'Contains' 'Air Around The Clock' on 'ActivityAdvertiserLabel'"...
[10/14 09:27:28,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 7 "Wait for 'TextContent' 'Contains' 'Air Around The Clock' on 'ActivityAdvertiserLabel'".
[10/14 09:27:28,ArtOfTest.Runner.exe(13916:9),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:27:28,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 8 "RadComboBox('ActivityTypeComboBox'): selecting item with index '1'"...
[10/14 09:27:30,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 8 "RadComboBox('ActivityTypeComboBox'): selecting item with index '1'".
[10/14 09:27:30,ArtOfTest.Runner.exe(13916:9),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:27:30,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 9 "Click 'EventITag'"...
[10/14 09:27:30,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 9 "Click 'EventITag'".
[10/14 09:27:30,ArtOfTest.Runner.exe(13916:9),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:27:30,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 10 "RadCalendar('ActivityDateCalendar'): Select date '1/1/0001'"...
[10/14 09:27:30,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 10 "RadCalendar('ActivityDateCalendar'): Select date '1/1/0001'".
[10/14 09:27:30,ArtOfTest.Runner.exe(13916:9),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:27:31,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 11 "TimeView('StartTimeTimeView'): select time '5:00 PM'."...
[10/14 09:27:31,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 11 "TimeView('StartTimeTimeView'): select time '5:00 PM'.".
[10/14 09:27:31,ArtOfTest.Runner.exe(13916:9),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:27:31,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 12 "TimeView('EndTimeTimeView'): select time '6:00 PM'."...
[10/14 09:27:32,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 12 "TimeView('EndTimeTimeView'): select time '6:00 PM'.".
[10/14 09:27:32,ArtOfTest.Runner.exe(13916:9),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:27:32,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 13 "RadInput('ctl00_cphM_ecRequiredDetails_txtDescription_wrapper'): value '401 N Front St, Columbus, OH 43215' entered."...
[10/14 09:27:32,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 13 "RadInput('ctl00_cphM_ecRequiredDetails_txtDescription_wrapper'): value '401 N Front St, Columbus, OH 43215' entered.".
[10/14 09:27:32,ArtOfTest.Runner.exe(13916:9),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:27:35,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 14 "Wait for 'TextContent' 'Contains' '
' on 'MapAddressLabel' - DataDriven: [$(AddressStreet)]"...
[10/14 09:27:35,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 14 "Wait for 'TextContent' 'Contains' '
' on 'MapAddressLabel' - DataDriven: [$(AddressStreet)]".
[10/14 09:27:35,ArtOfTest.Runner.exe(13916:9),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:27:35,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 15 "RadButton('NotesButton'): is clicked"...
[10/14 09:27:35,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 15 "RadButton('NotesButton'): is clicked".
[10/14 09:27:35,ArtOfTest.Runner.exe(13916:9),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:27:35,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 16 "RadInput('ActivityPurposeInput'): value 'Automated Test Activity 1' entered."...
[10/14 09:27:35,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 16 "RadInput('ActivityPurposeInput'): value 'Automated Test Activity 1' entered.".
[10/14 09:27:35,ArtOfTest.Runner.exe(13916:9),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:27:36,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 17 "RadInput('ActivityNotesInput'): value 'Automated test activity 1 notes' entered."...
[10/14 09:27:36,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 17 "RadInput('ActivityNotesInput'): value 'Automated test activity 1 notes' entered.".
[10/14 09:27:36,ArtOfTest.Runner.exe(13916:9),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:27:36,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 18 "Execute test 'Extract Activity Detail values step'"...
[10/14 09:27:36,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.InternalExecuteTest() : Executing test: 'Extract Activity Detail values step', path: 'Activity\Steps\Extract Activity Detail values step.tstest.'
[10/14 09:27:36,ArtOfTest.Runner.exe(13916:9),TestStudio] VisualDebugger.SetCurrentResult() : VisualDebugger::SetCurrentResult
[10/14 09:27:36,ArtOfTest.Runner.exe(13916:9),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:27:36,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 1 "RadComboBox('ActivityTypeComboBox')Extract text into DataBindVariable $(ActivityTypeComboBox)"...
[10/14 09:27:36,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 1 "RadComboBox('ActivityTypeComboBox')Extract text into DataBindVariable $(ActivityTypeComboBox)".
[10/14 09:27:36,ArtOfTest.Runner.exe(13916:9),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:27:36,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 2 "RadPicker('ActivityDatePicker')Extract DatePicker selected date into DataBindVariable $(Date)"...
[10/14 09:27:37,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 2 "RadPicker('ActivityDatePicker')Extract DatePicker selected date into DataBindVariable $(Date)".
[10/14 09:27:37,ArtOfTest.Runner.exe(13916:9),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:27:37,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 3 "RadPicker('StartTimePicker')Extract TimePicker selected time into DataBindVariable $(CphMEcActivityGeneralTpStartTimeWrapperDiv)"...
[10/14 09:27:37,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 3 "RadPicker('StartTimePicker')Extract TimePicker selected time into DataBindVariable $(CphMEcActivityGeneralTpStartTimeWrapperDiv)".
[10/14 09:27:37,ArtOfTest.Runner.exe(13916:9),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:27:38,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 4 "RadPicker('EndTimePicker')Extract TimePicker selected time into DataBindVariable $(EndTime)"...
[10/14 09:27:38,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 4 "RadPicker('EndTimePicker')Extract TimePicker selected time into DataBindVariable $(EndTime)".
[10/14 09:27:38,ArtOfTest.Runner.exe(13916:9),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:27:38,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 5 "Extract input 'LocationInput' value into DataBindVariable $(Location)."...
[10/14 09:27:38,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 5 "Extract input 'LocationInput' value into DataBindVariable $(Location).".
[10/14 09:27:38,ArtOfTest.Runner.exe(13916:9),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:27:38,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 6 "RadComboBox('ActivityAdvertiserComboBox')Extract text into DataBindVariable $(ActivityAdvertiserComboBox)"...
[10/14 09:27:38,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 6 "RadComboBox('ActivityAdvertiserComboBox')Extract text into DataBindVariable $(ActivityAdvertiserComboBox)".
[10/14 09:27:38,ArtOfTest.Runner.exe(13916:9),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:27:39,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 7 "RadComboBox('AeComboBox')Extract text into DataBindVariable $(Ae)"...
[10/14 09:27:39,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 7 "RadComboBox('AeComboBox')Extract text into DataBindVariable $(Ae)".
[10/14 09:27:39,ArtOfTest.Runner.exe(13916:9),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:27:39,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 8 "Extract 'TextContent' on 'PurposeTextArea' into DataBindVariable $(Purpose)"...
[10/14 09:27:39,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 8 "Extract 'TextContent' on 'PurposeTextArea' into DataBindVariable $(Purpose)".
[10/14 09:27:39,ArtOfTest.Runner.exe(13916:9),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:27:39,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 9 "Extract 'TextContent' on 'NotesTextArea' into DataBindVariable $(Notes)"...
[10/14 09:27:39,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 9 "Extract 'TextContent' on 'NotesTextArea' into DataBindVariable $(Notes)".
[10/14 09:27:39,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 18 "Execute test 'Extract Activity Detail values step'".
[10/14 09:27:39,ArtOfTest.Runner.exe(13916:9),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:27:39,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 19 "RadButton('SaveButton'): is clicked"...
[10/14 09:27:39,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 19 "RadButton('SaveButton'): is clicked".
[10/14 09:27:39,ArtOfTest.Runner.exe(13916:9),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:27:39,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 20 "Wait '10000' msec for url:'/Efficio/Opportunity/Activities.aspx'"...
[10/14 09:27:41,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 20 "Wait '10000' msec for url:'/Efficio/Opportunity/Activities.aspx'".
[10/14 09:27:41,ArtOfTest.Runner.exe(13916:9),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:27:41,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 21 "Retrieve the Activity ID"...
[10/14 09:27:41,ArtOfTest.Runner.exe(13916:9),Uncategorized] Log.WriteLineInternal() : Connecting to database.
[10/14 09:27:41,ArtOfTest.Runner.exe(13916:9),Uncategorized] Log.WriteLineInternal() : Retrieved Activity - ID: 4639, Advertiser GUID: ba72f88b-b4a8-4729-8924-35cfc8809989
[10/14 09:27:41,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 21 "Retrieve the Activity ID".
[10/14 09:27:41,ArtOfTest.Runner.exe(13916:9),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:27:41,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 22 "Verify Exists 'GlobalConfirmationMessage'"...
[10/14 09:27:42,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 22 "Verify Exists 'GlobalConfirmationMessage'".
[10/14 09:27:42,ArtOfTest.Runner.exe(13916:9),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:27:42,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 23 "Navigate to : '' - DataDriven: [$(EditActivityUrl)]"...
[10/14 09:27:44,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 23 "Navigate to : '' - DataDriven: [$(EditActivityUrl)]".
[10/14 09:27:44,ArtOfTest.Runner.exe(13916:9),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:27:44,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 24 "Wait '5000' msec for url:'/Efficio/Opportunity/OpportunityDetail.aspx'"...
[10/14 09:27:44,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 24 "Wait '5000' msec for url:'/Efficio/Opportunity/OpportunityDetail.aspx'".
[10/14 09:27:44,ArtOfTest.Runner.exe(13916:9),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:27:44,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 25 "Execute test 'Verify Activity Detail values step'"...
[10/14 09:27:44,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.InternalExecuteTest() : Executing test: 'Verify Activity Detail values step', path: 'Activity\Steps\Verify Activity Detail values step.tstest.'
[10/14 09:27:44,ArtOfTest.Runner.exe(13916:9),TestStudio] VisualDebugger.SetCurrentResult() : VisualDebugger::SetCurrentResult
[10/14 09:27:44,ArtOfTest.Runner.exe(13916:9),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:27:44,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 1 "Verify 'TextContent' 'Contains' 'Air Around The Clock' on 'ActivityAdvertiserLabel'"...
[10/14 09:27:44,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 1 "Verify 'TextContent' 'Contains' 'Air Around The Clock' on 'ActivityAdvertiserLabel'".
[10/14 09:27:44,ArtOfTest.Runner.exe(13916:9),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:27:44,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 2 "RadComboBox('ctl00_cphM_ecActivityGeneral_ddlActivityType'): text is '
'. - DataDriven: [$(Type)]"...
[10/14 09:27:44,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 2 "RadComboBox('ctl00_cphM_ecActivityGeneral_ddlActivityType'): text is '
'. - DataDriven: [$(Type)]".
[10/14 09:27:44,ArtOfTest.Runner.exe(13916:9),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:27:44,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 3 "RadPicker('ctl00_cphM_ecActivityGeneral_dpActivityDate_wrapper'): DatePicker selected date is '1/1/0001' - DataDriven: [$(Date)]"...
[10/14 09:27:45,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 3 "RadPicker('ctl00_cphM_ecActivityGeneral_dpActivityDate_wrapper'): DatePicker selected date is '1/1/0001' - DataDriven: [$(Date)]".
[10/14 09:27:45,ArtOfTest.Runner.exe(13916:9),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:27:45,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 4 "RadPicker('ctl00_cphM_ecActivityGeneral_tpStartTime_wrapper'): TimePicker selected time is '12:00 AM' - DataDriven: [$(StartTime)]"...
[10/14 09:27:45,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 4 "RadPicker('ctl00_cphM_ecActivityGeneral_tpStartTime_wrapper'): TimePicker selected time is '12:00 AM' - DataDriven: [$(StartTime)]".
[10/14 09:27:45,ArtOfTest.Runner.exe(13916:9),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:27:45,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 5 "RadPicker('ctl00_cphM_ecActivityGeneral_tpEndTime_wrapper'): TimePicker selected time is '12:00 AM' - DataDriven: [$(EndTime)]"...
[10/14 09:27:46,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 5 "RadPicker('ctl00_cphM_ecActivityGeneral_tpEndTime_wrapper'): TimePicker selected time is '12:00 AM' - DataDriven: [$(EndTime)]".
[10/14 09:27:46,ArtOfTest.Runner.exe(13916:9),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:27:46,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 6 "RadInput('LocationRadInput'): value 'Exact' ''. - DataDriven: [$(Location)]"...
[10/14 09:27:46,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 6 "RadInput('LocationRadInput'): value 'Exact' ''. - DataDriven: [$(Location)]".
[10/14 09:27:46,ArtOfTest.Runner.exe(13916:9),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:27:46,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 7 "RadComboBox('ctl00_cphM_ecActivityGeneral_ddlActivityAdvertiser'): text is ''. - DataDriven: [$(Advertiser)]"...
[10/14 09:27:46,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 7 "RadComboBox('ctl00_cphM_ecActivityGeneral_ddlActivityAdvertiser'): text is ''. - DataDriven: [$(Advertiser)]".
[10/14 09:27:46,ArtOfTest.Runner.exe(13916:9),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:27:46,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 8 "RadComboBox('ctl00_cphM_ecActivityGeneral_edlActivityAE'): text is ''. - DataDriven: [$(Ae)]"...
[10/14 09:27:46,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 8 "RadComboBox('ctl00_cphM_ecActivityGeneral_edlActivityAE'): text is ''. - DataDriven: [$(Ae)]".
[10/14 09:27:46,ArtOfTest.Runner.exe(13916:9),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:27:46,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 9 "RadInput('ctl00_cphM_ecActivityGeneral_txtActivityPurpose_wrapper'): value 'Exact' '
'. - DataDriven: [$(Purpose)]"...
[10/14 09:27:47,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 9 "RadInput('ctl00_cphM_ecActivityGeneral_txtActivityPurpose_wrapper'): value 'Exact' '
'. - DataDriven: [$(Purpose)]".
[10/14 09:27:47,ArtOfTest.Runner.exe(13916:9),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:27:47,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 10 "RadInput('ctl00_cphM_ecActivityNotes_txtActivityNotes_wrapper'): value 'Exact' '123'. - DataDriven: [$(Notes)]"...
[10/14 09:27:47,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 10 "RadInput('ctl00_cphM_ecActivityNotes_txtActivityNotes_wrapper'): value 'Exact' '123'. - DataDriven: [$(Notes)]".
[10/14 09:27:47,ArtOfTest.Runner.exe(13916:9),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:27:47,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 11 "RadButton('LocationButton'): is clicked"...
[10/14 09:27:48,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 11 "RadButton('LocationButton'): is clicked".
[10/14 09:27:48,ArtOfTest.Runner.exe(13916:9),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:27:48,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 12 "Wait for 'TextContent' 'Contains' '
' on 'MapAddressLabel' - DataDriven: [$(AddressStreet)]"...
[10/14 09:27:48,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 12 "Wait for 'TextContent' 'Contains' '
' on 'MapAddressLabel' - DataDriven: [$(AddressStreet)]".
[10/14 09:27:48,ArtOfTest.Runner.exe(13916:9),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 25 "Execute test 'Verify Activity Detail values step'".
[10/14 09:27:48,ArtOfTest.Runner.exe(13916:9),Uncategorized] Log.WriteLineInternal() : Test completed - performing cleanup
[10/14 09:27:48,ArtOfTest.Runner.exe(13916:9),Uncategorized] Log.WriteLineInternal() : Deleting Activity - ID: 4639
[10/14 09:27:48,ArtOfTest.Runner.exe(13916:9),Uncategorized] Log.WriteLineInternal() : Connecting to database.
[10/14 09:27:48,Telerik.TestStudio.ScreenRecorder.exe(14360:1)] First trace message from process 14360: "D:\Program Files\Progress\Test Studio\Bin\\Telerik.TestStudio.ScreenRecorder.exe" startnamedpipe="Pipes.ArtOfTest.WebAii.ScreenRecording.Control.132155332419587920" o="D:\Logs\TelerikTestStudio\Recording\Add activity from Activity Detail page_63706642041958.avi" c=mjpeg s=100 f=5 size=0 d=\\.\DISPLAY1
[10/14 09:27:48,Telerik.TestStudio.ScreenRecorder.exe(14360:1)] First trace message from foreground unnamed thread (managed ID = 1, native ID = 14900).
[10/14 09:27:48,Telerik.TestStudio.ScreenRecorder.exe(14360:1),Error] Program.Main() : EXCEPTION! (see below)
     Outer Exception Type: System.AggregateException
     Message: One or more errors occurred.
     HRESULT: 0x80131500 (Official ID (if app.) = COR_E_EXCEPTION, Error Bit = FAILED, Facility = FACILITY_URT, Code = 5376)
     Call Stack:
          at System.Threading.Tasks.Task.ThrowIfExceptional(Boolean includeTaskCanceledExceptions)
          at System.Threading.Tasks.Task.Wait(Int32 millisecondsTimeout, CancellationToken cancellationToken)
          at Telerik.TestStudio.ScreenRecorder.Program.Main(String[] args)

     Inner Exception Type: System.Threading.Tasks.TaskCanceledException
     Message: A task was canceled.
     HRESULT: 0x8013153B (Official ID (if app.) = COR_E_OPERATIONCANCELED, Error Bit = FAILED, Facility = FACILITY_URT, Code = 5435)

[10/14 09:27:48,ArtOfTest.Runner.exe(13916:9),TestStudio] VisualDebugger.ShutDown() : VisualDebbuger::ShutDown::Start
[10/14 09:27:48,ArtOfTest.Runner.exe(13916:9),TestStudio] VisualDebugger.ShutDown() : VisualDebbuger::ShutDown::End
[10/14 09:27:48,ArtOfTest.Runner.exe(13916:9),Framework] BrowserProvisioner.EndService() : Connecting to the provisioning pipe "Pipe.ArtOfTest.WebAii.BrowserProvisioner_2042020003" in order to unblock and eventually terminate it...
[10/14 09:27:48,ArtOfTest.Runner.exe(13916:9),Framework] BrowserProvisioner.EndService() : Provisioning pipe connected.
[10/14 09:27:48,ArtOfTest.Runner.exe(13916:6),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe but this will be treated as a termination cue.
[10/14 09:27:48,ArtOfTest.Runner.exe(13916:9),Framework] BrowserProvisioner.EndService() : Provisioning thread terminated.
[10/14 09:27:48,ArtOfTest.Runner.exe(13916:9),DialogPlayback] DialogMonitor.AddDialog() : Added {OnBeforeUnloadDialog(Dismiss=OK)} for monitoring.
[10/14 09:27:48,ArtOfTest.Runner.exe(13916:9),Framework] Manager.RemoveBrowser() : Removed client ID "Client_c23d6166-2610-49c5-8e5b-1829f6fe8757" (0 remaining).
[10/14 09:27:48,ArtOfTest.Runner.exe(13916:9),Framework] Manager.SetActiveBrowser() : Active browser is now null.
[10/14 09:27:48,ArtOfTest.Runner.exe(13916:9),Framework] BrowserRemoted.End() : Now aborting the listener thread (TID = 7).
[10/14 09:27:48,IEXPLORE.EXE(11372:1),Framework] BrowserRemoteClient.Disconnect() : Sending ClientDisconnectRequest(ClientId="Client_c23d6166-2610-49c5-8e5b-1829f6fe8757") to pipe server...
[10/14 09:27:48,IEXPLORE.EXE(11372:1),Framework] BrowserRemoteClient.Disconnect() : Command pipe is already disconnected.
[10/14 09:27:48,ArtOfTest.Runner.exe(13916:7),Warning] PipeCommunication.SyncPipeRead() : Pipe read broken by thread-abort.
[10/14 09:27:51,ArtOfTest.Runner.exe(13916:9),DialogPlayback] DialogMonitor.Stop() : Dialog monitoring is being halted.
[10/14 09:27:51,ArtOfTest.Runner.exe(13916:9),UIAutomation] AutomationExtensions.AddAutomationEventHandler() : Added a(n) WindowPatternIdentifiers.WindowOpenedProperty handler on element System.Windows.Automation.AutomationElement scope Descendants.
[10/14 09:27:52,Telerik.TestStudio.RemoteExecutor.exe(11408:23),Execution] TestPlatform.FinishTestRunAndPublishResultsAsync() : Finished running test platform - attempting publish
[10/14 09:27:54,Telerik.TestStudio.RemoteExecutor.exe(11408:16)] First trace message from pool unnamed thread (managed ID = 16, native ID = 10688).
[10/14 09:27:54,Telerik.TestStudio.RemoteExecutor.exe(11408:16),Execution] RemoteExecutorPipeClient.StartTest() : Test run requested for test with path 'Activity\Activity deep linking.tstest'. Project path: 'C:\Efficio\TelerikTestStudio\Efficio Main Application'.
[10/14 09:27:54,Telerik.TestStudio.RemoteExecutor.exe(11408:12),Framework] TestAppLoader.LoadRefAssemblies() : External assembly loaded into the AppDomain: C:\Temp\Telerik Test Studio\TSProjects\92c411c3-850c-4006-89a6-a95aed591e6f\Efficio Main Application\..\Efficio Main Application\lib\Microsoft.ApplicationBlocks.Data.dll.
[10/14 09:27:54,ArtOfTest.Runner.exe(13916:4)] First trace message from pool unnamed thread (managed ID = 4, native ID = 14008).
[10/14 09:27:54,ArtOfTest.Runner.exe(13916:4),Framework] TestAppLoader.LoadRefAssemblies() : External assembly loaded into the AppDomain: C:\Temp\Telerik Test Studio\TSProjects\92c411c3-850c-4006-89a6-a95aed591e6f\Efficio Main Application\..\Efficio Main Application\lib\Microsoft.ApplicationBlocks.Data.dll.
[10/14 09:27:54,ArtOfTest.Runner.exe(13916:4),TestStudio] Manager.GetDialogMatchProvider() : The dialog matches assembly loaded successfully.
[10/14 09:27:54,ArtOfTest.Runner.exe(13916:4),Framework] Manager.KillNonAutomatedBrowsers() : Closing non-automated 'iexplore' browser instances
[10/14 09:27:54,ArtOfTest.Runner.exe(13916:4),Framework] InternetExplorerActions.LaunchNewBrowserInstance() : Process launched (ID=9220, Path="C:\Program Files\Internet Explorer\iexplore.exe", Arguments="-nomerge about:blank").
[10/14 09:27:55,ArtOfTest.Runner.exe(13916:4),Framework] InternetExplorerActions.LaunchNewBrowserInstance() : Attempting to attach on IE frame (HWND=657086)...
[10/14 09:27:55,ArtOfTest.Runner.exe(13916:4),Framework] Connector.Start() : Process launched (ID=15880, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""D:\Program Files\Progress\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 1902196").
[10/14 09:27:55,ArtOfTest.Runner.exe(13916:4),Framework] Connector.Start() : Process exited (ID=15880, ExitCode=0, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""D:\Program Files\Progress\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 1902196").
[10/14 09:27:55,ArtOfTest.Runner.exe(13916:4),Framework] Connector.InjectCode() : ------- Function Start -------
[10/14 09:27:55,ArtOfTest.Runner.exe(13916:4),Framework] Connector.InjectCode() : Connection string: C:\Windows\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2019.3.1002.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint?11408
[10/14 09:27:55,ArtOfTest.Runner.exe(13916:4),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 1902196 on another thread...
[10/14 09:27:55,ArtOfTest.Runner.exe(13916:5)] First trace message from pool thread "WaitAsync polling wait worker" (managed ID = 5, native ID = 12928).
[10/14 09:27:55,ArtOfTest.Runner.exe(13916:5),Framework] <>c__DisplayClass26_3.<WaitForDocument>b__1() : Attempting to get document from window handle
[10/14 09:27:55,ArtOfTest.Runner.exe(13916:4),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[10/14 09:27:55,ArtOfTest.Runner.exe(13916:4),Framework] Connector.InjectCode() : Sending WM_COPYDATA to HWND 1902196 (lpData = "C:\Windows\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2019.3.1002.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint?11408")...
[10/14 09:27:55,IEXPLORE.EXE(9148:1)] First trace message from process 9148: "C:\Program Files (x86)\Internet Explorer\IEXPLORE.EXE" SCODEF:9220 CREDAT:275457 /prefetch:2
[10/14 09:27:55,IEXPLORE.EXE(9148:1)] First trace message from background unnamed thread (managed ID = 1, native ID = 16108).
[10/14 09:27:55,IEXPLORE.EXE(9148:1),Framework] ArtOfTestPluginEntryPoint.Connect() : The .NET Framework is now active inside the hooked process (HWND = 1902196, PipeName = "Pipe.ArtOfTest.WebAii.BrowserProvisioner_-770344345", PID = 11408).
[10/14 09:27:55,IEXPLORE.EXE(9148:1),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 1902196 on same thread...
[10/14 09:27:55,IEXPLORE.EXE(9148:1),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[10/14 09:27:55,IEXPLORE.EXE(9148:1),Framework] BrowserRemoteClient constructor : The client ID for this hooked process will be "Client_3005e5c6-a6b2-4351-b6c4-54674653312b".
[10/14 09:27:55,ArtOfTest.Runner.exe(13916:20)] First trace message from background thread "Pipe.ArtOfTest.WebAii.BrowserProvisioner" (managed ID = 20, native ID = 10972).
[10/14 09:27:55,ArtOfTest.Runner.exe(13916:20),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe.
[10/14 09:27:55,ArtOfTest.Runner.exe(13916:23)] First trace message from background thread "Remoted async command listener" (managed ID = 23, native ID = 1472).
[10/14 09:27:55,ArtOfTest.Runner.exe(13916:23),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Not connected; this will now wait indefinitely for a pipe client connection...
[10/14 09:27:55,IEXPLORE.EXE(9148:1),Framework] BrowserRemoteClient.CreateNamedPipes() : Named pipes created for communication with the server: {Command = "2f44e2f6-7170-4c20-9f35-cc5ed77912c9.CommandPipe", Async = "2f44e2f6-7170-4c20-9f35-cc5ed77912c9.AsyncPipe"}
[10/14 09:27:55,ArtOfTest.Runner.exe(13916:23),Framework] BrowserRemoted.AsyncListenerThreadEntry() : A new client has connected to the pipe.
[10/14 09:27:55,ArtOfTest.Runner.exe(13916:23),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Now waiting indefinitely for a command from the pipe client...
[10/14 09:27:55,ArtOfTest.Runner.exe(13916:20),Framework] Manager.AddNewBrowser() : Added client ID "Client_3005e5c6-a6b2-4351-b6c4-54674653312b" (now there is/are 1 total).
[10/14 09:27:55,ArtOfTest.Runner.exe(13916:4),Framework] Connector.InjectCode() : Returned from sending WM_COPYDATA (LRESULT=0, GetLastError()=ERROR_ALREADY_EXISTS).
[10/14 09:27:55,ArtOfTest.Runner.exe(13916:4),Framework] Connector.InjectCode() : ------- Function End -------
[10/14 09:27:55,ArtOfTest.Runner.exe(13916:4),Execution] ExecutionEngine.InternalExecuteTest() : Executing test: 'Activity deep linking', path: 'Activity\Activity deep linking.tstest.'
[10/14 09:27:56,ArtOfTest.Runner.exe(13916:4),TestStudio] VisualDebugger.SetCurrentResult() : VisualDebugger::SetCurrentResult
[10/14 09:27:56,ArtOfTest.Runner.exe(13916:4),DialogPlayback] DialogMonitor.Start() : Beginning monitoring for dialogs from the set {}.
[10/14 09:27:56,ArtOfTest.Runner.exe(13916:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:27:56,ArtOfTest.Runner.exe(13916:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 1 "Get advertiser"...
[10/14 09:27:56,ArtOfTest.Runner.exe(13916:4),Uncategorized] Log.WriteLineInternal() : Retrieved advertiser - ID: 2149, GUID: 2d667435-5c30-4700-9e43-a66dece025f3, Name: 1 800 Flowers
[10/14 09:27:56,ArtOfTest.Runner.exe(13916:4),Uncategorized] Log.WriteLineInternal() : Retrieved advertiser - ID: 2166, GUID: b8aec5c4-a0c0-4f39-baed-7defd22ad493, Name: Advertiser One for Automated Testing
[10/14 09:27:56,ArtOfTest.Runner.exe(13916:4),Uncategorized] Log.WriteLineInternal() : Retrieved activity - ID: 3945, Purpose: Testing Activity 1, Advertiser GUID: ba72f88b-b4a8-4729-8924-35cfc8809989, Advertiser Name: Air Around The Clock
[10/14 09:27:56,ArtOfTest.Runner.exe(13916:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 1 "Get advertiser".
[10/14 09:27:56,ArtOfTest.Runner.exe(13916:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:27:56,ArtOfTest.Runner.exe(13916:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 2 "Execute test 'Login step'"...
[10/14 09:27:56,ArtOfTest.Runner.exe(13916:4),Execution] ExecutionEngine.InternalExecuteTest() : Executing test: 'Login step', path: 'Login\Steps\Login step.tstest.'
[10/14 09:27:56,ArtOfTest.Runner.exe(13916:4),TestStudio] VisualDebugger.SetCurrentResult() : VisualDebugger::SetCurrentResult
[10/14 09:27:56,ArtOfTest.Runner.exe(13916:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:27:56,ArtOfTest.Runner.exe(13916:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 1 "Login"...
[10/14 09:27:58,ArtOfTest.Runner.exe(13916:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 1 "Login".
[10/14 09:27:58,ArtOfTest.Runner.exe(13916:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 2 "Execute test 'Login step'".
[10/14 09:27:58,ArtOfTest.Runner.exe(13916:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:27:58,ArtOfTest.Runner.exe(13916:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 3 "Create URLs for deep linking"...
[10/14 09:27:58,ArtOfTest.Runner.exe(13916:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 3 "Create URLs for deep linking".
[10/14 09:27:58,ArtOfTest.Runner.exe(13916:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:27:58,ArtOfTest.Runner.exe(13916:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 4 "Navigate to : '' - DataDriven: [$(AdvertiserUrlOne)]"...
[10/14 09:28:00,ArtOfTest.Runner.exe(13916:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 4 "Navigate to : '' - DataDriven: [$(AdvertiserUrlOne)]".
[10/14 09:28:00,ArtOfTest.Runner.exe(13916:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:28:00,ArtOfTest.Runner.exe(13916:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 5 "Wait '5000' msec for url:'/Opportunity/OpportunityDetail.aspx'"...
[10/14 09:28:00,ArtOfTest.Runner.exe(13916:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 5 "Wait '5000' msec for url:'/Opportunity/OpportunityDetail.aspx'".
[10/14 09:28:00,ArtOfTest.Runner.exe(13916:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:28:00,ArtOfTest.Runner.exe(13916:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 6 "Verify 'TextContent' 'Exact' '' on 'AdvertiserNameLabel' - DataDriven: [$(AdvertiserNameOne)]"...
[10/14 09:28:00,ArtOfTest.Runner.exe(13916:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 6 "Verify 'TextContent' 'Exact' '' on 'AdvertiserNameLabel' - DataDriven: [$(AdvertiserNameOne)]".
[10/14 09:28:00,ArtOfTest.Runner.exe(13916:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:28:00,ArtOfTest.Runner.exe(13916:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 7 "RadComboBox('ctl00_cphM_ecAdvertiserFind_ddlAdvertiser'): text is ''. - DataDriven: [$(AdvertiserNameOne)]"...
[10/14 09:28:00,ArtOfTest.Runner.exe(13916:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 7 "RadComboBox('ctl00_cphM_ecAdvertiserFind_ddlAdvertiser'): text is ''. - DataDriven: [$(AdvertiserNameOne)]".
[10/14 09:28:00,ArtOfTest.Runner.exe(13916:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:28:00,ArtOfTest.Runner.exe(13916:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 8 "Navigate to : '' - DataDriven: [$(AdvertiserUrlTwo)]"...
[10/14 09:28:03,ArtOfTest.Runner.exe(13916:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 8 "Navigate to : '' - DataDriven: [$(AdvertiserUrlTwo)]".
[10/14 09:28:03,ArtOfTest.Runner.exe(13916:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:28:03,ArtOfTest.Runner.exe(13916:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 9 "Wait '5000' msec for url:'/Opportunity/OpportunityDetail.aspx'"...
[10/14 09:28:03,ArtOfTest.Runner.exe(13916:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 9 "Wait '5000' msec for url:'/Opportunity/OpportunityDetail.aspx'".
[10/14 09:28:03,ArtOfTest.Runner.exe(13916:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:28:03,ArtOfTest.Runner.exe(13916:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 10 "Verify 'TextContent' 'Exact' '' on 'AdvertiserNameLabel' - DataDriven: [$(AdvertiserNameTwo)]"...
[10/14 09:28:03,ArtOfTest.Runner.exe(13916:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 10 "Verify 'TextContent' 'Exact' '' on 'AdvertiserNameLabel' - DataDriven: [$(AdvertiserNameTwo)]".
[10/14 09:28:03,ArtOfTest.Runner.exe(13916:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:28:03,ArtOfTest.Runner.exe(13916:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 11 "Navigate to : '' - DataDriven: [$(EditUrlThree)]"...
[10/14 09:28:05,ArtOfTest.Runner.exe(13916:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 11 "Navigate to : '' - DataDriven: [$(EditUrlThree)]".
[10/14 09:28:05,ArtOfTest.Runner.exe(13916:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:28:05,ArtOfTest.Runner.exe(13916:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 12 "Wait '5000' msec for url:'/Opportunity/OpportunityDetail.aspx'"...
[10/14 09:28:05,ArtOfTest.Runner.exe(13916:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 12 "Wait '5000' msec for url:'/Opportunity/OpportunityDetail.aspx'".
[10/14 09:28:05,ArtOfTest.Runner.exe(13916:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:28:05,ArtOfTest.Runner.exe(13916:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 13 "Verify 'TextContent' 'Exact' '' on 'AdvertiserNameLabel' - DataDriven: [$(AdvertiserNameThree)]"...
[10/14 09:28:05,ArtOfTest.Runner.exe(13916:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 13 "Verify 'TextContent' 'Exact' '' on 'AdvertiserNameLabel' - DataDriven: [$(AdvertiserNameThree)]".
[10/14 09:28:05,ArtOfTest.Runner.exe(13916:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 09:28:05,ArtOfTest.Runner.exe(13916:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 14 "RadInput('ctl00_cphM_ecActivityGeneral_txtActivityPurpose_wrapper'): value 'Exact' 'make contacts - Test activity 2 '. - DataDriven: [$(PurposeThree)]"...
[10/14 09:28:06,ArtOfTest.Runner.exe(13916:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 14 "RadInput('ctl00_cphM_ecActivityGeneral_txtActivityPurpose_wrapper'): value 'Exact' 'make contacts - Test activity 2 '. - DataDriven: [$(PurposeThree)]".
[10/14 09:28:06,Telerik.TestStudio.ScreenRecorder.exe(9884:1)] First trace message from process 9884: "D:\Program Files\Progress\Test Studio\Bin\\Telerik.TestStudio.ScreenRecorder.exe" startnamedpipe="Pipes.ArtOfTest.WebAii.ScreenRecording.Control.132155332762776752" o="D:\Logs\TelerikTestStudio\Recording\Activity deep linking_63706642076277.avi" c=mjpeg s=100 f=5 size=0 d=\\.\DISPLAY1
[10/14 09:28:06,Telerik.TestStudio.ScreenRecorder.exe(9884:1)] First trace message from foreground unnamed thread (managed ID = 1, native ID = 13380).
[10/14 09:28:06,Telerik.TestStudio.ScreenRecorder.exe(9884:1),Error] Program.Main() : EXCEPTION! (see below)
     Outer Exception Type: System.AggregateException
     Message: One or more errors occurred.
     HRESULT: 0x80131500 (Official ID (if app.) = COR_E_EXCEPTION, Error Bit = FAILED, Facility = FACILITY_URT, Code = 5376)
     Call Stack:
          at System.Threading.Tasks.Task.ThrowIfExceptional(Boolean includeTaskCanceledExceptions)
          at System.Threading.Tasks.Task.Wait(Int32 millisecondsTimeout, CancellationToken cancellationToken)
          at Telerik.TestStudio.ScreenRecorder.Program.Main(String[] args)

     Inner Exception Type: System.Threading.Tasks.TaskCanceledException
     Message: A task was canceled.
     HRESULT: 0x8013153B (Official ID (if app.) = COR_E_OPERATIONCANCELED, Error Bit = FAILED, Facility = FACILITY_URT, Code = 5435)

[10/14 09:28:06,ArtOfTest.Runner.exe(13916:4),TestStudio] VisualDebugger.ShutDown() : VisualDebbuger::ShutDown::Start
[10/14 09:28:06,ArtOfTest.Runner.exe(13916:4),TestStudio] VisualDebugger.ShutDown() : VisualDebbuger::ShutDown::End
[10/14 09:28:06,ArtOfTest.Runner.exe(13916:4),Framework] BrowserProvisioner.EndService() : Connecting to the provisioning pipe "Pipe.ArtOfTest.WebAii.BrowserProvisioner_-770344345" in order to unblock and eventually terminate it...
[10/14 09:28:06,ArtOfTest.Runner.exe(13916:4),Framework] BrowserProvisioner.EndService() : Provisioning pipe connected.
[10/14 09:28:06,ArtOfTest.Runner.exe(13916:20),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe but this will be treated as a termination cue.
[10/14 09:28:06,ArtOfTest.Runner.exe(13916:4),Framework] BrowserProvisioner.EndService() : Provisioning thread terminated.
[10/14 09:28:06,ArtOfTest.Runner.exe(13916:4),DialogPlayback] DialogMonitor.AddDialog() : Added {OnBeforeUnloadDialog(Dismiss=OK)} for monitoring.
[10/14 09:28:06,ArtOfTest.Runner.exe(13916:4),Framework] Manager.RemoveBrowser() : Removed client ID "Client_3005e5c6-a6b2-4351-b6c4-54674653312b" (0 remaining).
[10/14 09:28:06,ArtOfTest.Runner.exe(13916:4),Framework] Manager.SetActiveBrowser() : Active browser is now null.
[10/14 09:28:06,ArtOfTest.Runner.exe(13916:4),Framework] BrowserRemoted.End() : Now aborting the listener thread (TID = 23).
[10/14 09:28:06,IEXPLORE.EXE(9148:1),Framework] BrowserRemoteClient.Disconnect() : Sending ClientDisconnectRequest(ClientId="Client_3005e5c6-a6b2-4351-b6c4-54674653312b") to pipe server...
[10/14 09:28:06,IEXPLORE.EXE(9148:1),Framework] BrowserRemoteClient.Disconnect() : Command pipe is already disconnected.
[10/14 09:28:06,ArtOfTest.Runner.exe(13916:23),Warning] PipeCommunication.SyncPipeRead() : Pipe read broken by thread-abort.
[10/14 09:28:07,ArtOfTest.Runner.exe(13916:4),DialogPlayback] DialogMonitor.Stop() : Dialog monitoring is being halted.
[10/14 09:28:07,ArtOfTest.Runner.exe(13916:4),UIAutomation] AutomationExtensions.AddAutomationEventHandler() : Added a(n) WindowPatternIdentifiers.WindowOpenedProperty handler on element System.Windows.Automation.AutomationElement scope Descendants.
[10/14 09:28:07,Telerik.TestStudio.RemoteExecutor.exe(11408:23),Execution] TestPlatform.FinishTestRunAndPublishResultsAsync() : Finished running test platform - attempting publish
[10/14 09:28:09,Telerik.TestStudio.ExecutionManagerService.exe(12852:16),Execution] JobRunner.<CleanupTestRunsStatus>b__19_0() : Test list 'Activity' finished. Updating dispatch group header job Id = 2353096d-e915-4d4a-9955-3040062a6968
[10/14 09:28:09,Telerik.TestStudio.ExecutionManagerService.exe(12852:16),Execution] JobRunner.<CleanupTestRunsStatus>b__19_0() : Test list 'Activity' finished. Sending notifications.
[10/14 09:28:09,Telerik.TestStudio.ExecutionManagerService.exe(12852:20),Execution] PostListExecutionEmailer.OnAfterTestListCompleted() : Attempting to send email for job 2353096d-e915-4d4a-9955-3040062a6968
[10/14 09:28:10,Telerik.TestStudio.ExecutionManagerService.exe(12852:20),Error] XmlFileHandler`1.Export() : EXCEPTION! (see below)
     Situation: Error saving file in XML format.
     Outer Exception Type: System.ArgumentException
     Message: '', hexadecimal value 0x1B, is an invalid character.
     HRESULT: 0x80070057 (Official ID (if app.) = E_INVALIDARG, Error Bit = FAILED, Facility = FACILITY_WIN32, Code = ERROR_INVALID_PARAMETER)
     Call Stack:
          at System.Xml.XmlUtf8RawTextWriter.InvalidXmlChar(Int32 ch, Byte* pDst, Boolean entitize)
          at System.Xml.XmlUtf8RawTextWriter.WriteElementTextBlock(Char* pSrc, Char* pSrcEnd)
          at System.Xml.XmlUtf8RawTextWriter.WriteString(String text)
          at System.Xml.XmlUtf8RawTextWriterIndent.WriteString(String text)
          at System.Xml.XmlRawWriter.WriteValue(String value)
          at System.Xml.XmlWellFormedWriter.WriteValue(String value)
          at ArtOfTest.Common.Design.XmlFileHandler`1.Export(String filePath, Dictionary`2 objectsWithPaths)

[10/14 09:28:10,Telerik.TestStudio.ExecutionManagerService.exe(12852:20),Error] RunResultHtmlExporter.Export() : EXCEPTION! (see below)
     Outer Exception Type: System.ArgumentNullException
     Message: Value cannot be null.
          Parameter name: path
     HRESULT: 0x80004003 (Official ID (if app.) = E_POINTER, Error Bit = FAILED, Facility = FACILITY_NULL, Code = 16387)
     Call Stack:
          at System.IO.StreamReader..ctor(String path, Encoding encoding, Boolean detectEncodingFromByteOrderMarks, Int32 bufferSize, Boolean checkHost)
          at System.IO.StreamReader..ctor(String path)
          at ArtOfTest.WebAii.Design.Execution.RunResultHtmlExporter.BuildTempHtml(String filePath, String xmlFilePath)
          at ArtOfTest.WebAii.Design.Execution.RunResultHtmlExporter.Export(String filePath, Dictionary`2 resultsWithPaths)

[10/14 09:28:10,Telerik.TestStudio.ExecutionManagerService.exe(12852:20),Execution] <>c__DisplayClass5_1.<JobFinished>b__3() : EXCEPTION! (see below)
     Situation: Exception in jobfinished plugin executionStatus
     Outer Exception Type: System.ArgumentNullException
     Message: Value cannot be null.
          Parameter name: fileName
     HRESULT: 0x80004003 (Official ID (if app.) = E_POINTER, Error Bit = FAILED, Facility = FACILITY_NULL, Code = 16387)
     Call Stack:
          at System.Net.Mail.AttachmentBase.SetContentFromFile(String fileName, String mediaType)
          at System.Net.Mail.AttachmentBase..ctor(String fileName)
          at System.Net.Mail.Attachment..ctor(String fileName)
          at Telerik.TestStudio.ExecutionManager.Notifications.Emailer.Mail.ResultMailSender.AddAttachment(String fileName)
          at Telerik.TestStudio.ExecutionManager.Notifications.Emailer.Mail.ResultMailSender.SetRunResultAttachment(RunResult runResult, NotificationSettings settings)
          at Telerik.TestStudio.ExecutionManager.Notifications.Emailer.PostListExecutionEmailer.OnAfterTestListCompleted(TestListExecutionStatus executionStatus, RunResult result)
          at Telerik.TestStudio.ExecutionManager.Notifications.ExecutionManagerNotificationService.<>c__DisplayClass5_1.<JobFinished>b__3(ITestListExecutionExtensionStatus extensionStatus)

[10/14 09:29:09,Telerik.TestStudio.ExecutionManagerService.exe(12852:18),Execution] JobRunner.<CleanupTestRunsStatus>b__19_0() : TestList execution status expired - removing from list job id=2353096d-e915-4d4a-9955-3040062a6968
